[INFO] fetching crate autorize 0.2.15...
[INFO] testing autorize-0.2.15 against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] extracting crate autorize 0.2.15 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate autorize 0.2.15
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate autorize 0.2.15
[INFO] tweaked toml for crates.io crate autorize 0.2.15 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate autorize 0.2.15 on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate autorize 0.2.15 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 02735dbcac098841721e078f97aeab4faa4208eb1b69849440946f90419f18b8
[INFO] running `Command { std: "docker" "start" "-a" "02735dbcac098841721e078f97aeab4faa4208eb1b69849440946f90419f18b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "02735dbcac098841721e078f97aeab4faa4208eb1b69849440946f90419f18b8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "02735dbcac098841721e078f97aeab4faa4208eb1b69849440946f90419f18b8", kill_on_drop: false }`
[INFO] [stdout] 02735dbcac098841721e078f97aeab4faa4208eb1b69849440946f90419f18b8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9911441095c56f7cb6e8f2716e1f44d09b8514fec7eac3107374194a96747d1f
[INFO] running `Command { std: "docker" "start" "-a" "9911441095c56f7cb6e8f2716e1f44d09b8514fec7eac3107374194a96747d1f", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling inventory v0.3.24
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling signal-hook v0.4.4
[INFO] [stderr]    Compiling num-conv v0.2.2
[INFO] [stderr]    Compiling serde_json_path_macros_internal v0.1.2
[INFO] [stderr]    Compiling winnow v1.0.3
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling symlink v0.1.0
[INFO] [stderr]    Compiling humantime v2.3.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling toml v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling humantime-serde v1.1.1
[INFO] [stderr]    Compiling serde_json_path_core v0.2.2
[INFO] [stderr]    Compiling serde_json_path_macros v0.1.6
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling serde_json_path v0.7.2
[INFO] [stderr]    Compiling tracing-appender v0.2.5
[INFO] [stderr]    Compiling autorize v0.2.15 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 14.92s
[INFO] running `Command { std: "docker" "inspect" "9911441095c56f7cb6e8f2716e1f44d09b8514fec7eac3107374194a96747d1f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9911441095c56f7cb6e8f2716e1f44d09b8514fec7eac3107374194a96747d1f", kill_on_drop: false }`
[INFO] [stdout] 9911441095c56f7cb6e8f2716e1f44d09b8514fec7eac3107374194a96747d1f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] a38d90bb9811fb0c30b5be4b62eda6920fd2e71d88f82b94edabd27b122bce02
[INFO] running `Command { std: "docker" "start" "-a" "a38d90bb9811fb0c30b5be4b62eda6920fd2e71d88f82b94edabd27b122bce02", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling nix v0.31.3
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling autorize v0.2.15 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.96s
[INFO] running `Command { std: "docker" "inspect" "a38d90bb9811fb0c30b5be4b62eda6920fd2e71d88f82b94edabd27b122bce02", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a38d90bb9811fb0c30b5be4b62eda6920fd2e71d88f82b94edabd27b122bce02", kill_on_drop: false }`
[INFO] [stdout] a38d90bb9811fb0c30b5be4b62eda6920fd2e71d88f82b94edabd27b122bce02
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 82d08b335515735e9204e92885d49cc1ad14a5921cd53b4a1e1c1a673cc3ee95
[INFO] running `Command { std: "docker" "start" "-a" "82d08b335515735e9204e92885d49cc1ad14a5921cd53b4a1e1c1a673cc3ee95", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.20s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/autorize-2f24d681ccfde9fb)
[INFO] [stdout] 
[INFO] [stdout] running 216 tests
[INFO] [stdout] test agent::tests::expand_env_passthrough_simple ... ok
[INFO] [stdout] test agent::tests::expand_env_no_expansion_when_no_dollar ... ok
[INFO] [stdout] test agent::tests::expand_env_missing_var_to_empty ... ok
[INFO] [stdout] test agent::tests::expand_env_preserves_dollar_followed_by_non_name ... ok
[INFO] [stdout] test agent::tests::expand_env_preserves_literal_text ... ok
[INFO] [stdout] test agent::tests::substitute_replaces_all_placeholders ... ok
[INFO] [stdout] test cli::backfill::tests::backfill_missing_experiment_errors ... ok
[INFO] [stdout] test cli::init::tests::creates_files_in_fresh_dir ... ok
[INFO] [stdout] test agent::tests::expand_env_braced_form ... ok
[INFO] [stdout] test cli::init::tests::refuses_bad_name ... ok
[INFO] [stdout] test cli::list::tests::render_colorized_has_ansi_escapes ... ok
[INFO] [stdout] test cli::list::tests::render_empty_summary_placeholder ... ok
[INFO] [stdout] test cli::list::tests::render_meta_omits_best_when_state_absent ... ok
[INFO] [stdout] test cli::list::tests::render_one_iteration_with_best ... ok
[INFO] [stdout] test cli::list::tests::render_several_oldest_first_with_outcomes_and_scores ... ok
[INFO] [stdout] test cli::list::tests::render_plain_has_no_ansi_escapes ... ok
[INFO] [stdout] test cli::list::tests::render_zero_iterations ... ok
[INFO] [stdout] test cli::list::tests::render_zero_iterations_ignores_state ... ok
[INFO] [stdout] test cli::init::tests::refuses_existing_experiment_dir ... ok
[INFO] [stdout] test cli::llms::tests::mentions_all_current_steps ... ok
[INFO] [stdout] test cli::llms::tests::mentions_all_outcomes ... ok
[INFO] [stdout] test cli::llms::tests::mentions_all_subcommands ... ok
[INFO] [stdout] test cli::llms::tests::prints_non_empty ... ok
[INFO] [stdout] test cli::backfill::tests::backfill_disabled_is_noop ... ok
[INFO] [stdout] test cli::llms::tests::llms_doc_mentions_every_config_field ... ok
[INFO] [stdout] test cli::llms::tests::mentions_all_parse_kinds_and_fail_modes ... ok
[INFO] [stdout] test agent::tests::run_agent_sets_workdir_var ... ok
[INFO] [stdout] test agent::tests::run_agent_prompt_file_mode ... ok
[INFO] [stdout] test agent::tests::run_agent_iter_substituted ... ok
[INFO] [stdout] test cli::backfill::tests::backfill_fills_missing_summary ... ok
[INFO] [stdout] test cli::backfill::tests::backfill_force_overwrites_existing_summary ... ok
[INFO] [stdout] test cli::clean::tests::clean_errors_when_experiment_missing ... ok
[INFO] [stderr] Preparing worktree (checking out 'autorize/test')
[INFO] [stdout] HEAD is now at c6cc76d init
[INFO] [stdout] test agent::tests::run_agent_stdin_prompt_mode ... ok
[INFO] [stdout] test cli::resume::tests::resume_with_no_state_errors ... ok
[INFO] [stdout] test cli::clean::tests::clean_prunes_dangling_registration ... ok
[INFO] [stdout] test cli::resume::tests::resume_skips_duplicate_record ... ok
[INFO] [stderr] fatal: 'autorize/test' is already used by worktree at '/tmp/.tmpQCShnE/.autorize/test/iter-0001/wt'
[INFO] [stdout] test cli::run::tests::fresh_on_in_progress_still_errors ... ok
[INFO] [stdout] test cli::clean::tests::clean_remove_worktrees_deletes_checkout_keeps_artifacts ... ok
[INFO] [stdout] test cli::run::tests::parse_merge_subject_matches_with_expected_iter ... ok
[INFO] [stdout] test cli::clean::tests::clean_frees_branch_held_by_worktree ... ok
[INFO] [stdout] test cli::run::tests::parse_merge_subject_rejects_mismatched_iter ... ok
[INFO] [stdout] test cli::run::tests::parse_merge_subject_round_trips_sentinel_floats ... ok
[INFO] [stdout] test cli::run::tests::parse_merge_subject_rejects_unrelated_subjects ... ok
[INFO] [stdout] test cli::run::tests::fresh_run_creates_branch_and_state ... ok
[INFO] [stdout] test cli::run::tests::fresh_errors_on_expired_absolute_deadline ... ok
[INFO] [stdout] test cli::resume::tests::resume_reconciles_merged_from_branch_tip ... ok
[INFO] [stdout] test cli::run::tests::fresh_on_never_run_behaves_like_first_run ... ok
[INFO] [stdout] test cli::clean::tests::clean_unstages_kept_worktree ... ok
[INFO] [stdout] test cli::status::tests::status_errors_when_state_missing ... ok
[INFO] [stdout] test cli::status::tests::status_omits_reason_when_notes_empty ... ok
[INFO] [stdout] test cli::status::tests::status_omits_summary_when_empty ... ok
[INFO] [stdout] test cli::status::tests::status_prints_best ... ok
[INFO] [stdout] test cli::status::tests::status_prints_last_reason ... ok
[INFO] [stdout] test cli::status::tests::status_prints_last_summary ... ok
[INFO] [stdout] test cli::status::tests::status_prints_no_iterations ... ok
[INFO] [stdout] test cli::tell::tests::tell_appends_multiple_in_order ... ok
[INFO] [stdout] test cli::run::tests::refuses_concurrent_run ... ok
[INFO] [stdout] test cli::tell::tests::tell_appends_with_in_progress_iter ... ok
[INFO] [stdout] test cli::tell::tests::tell_missing_experiment_errors ... ok
[INFO] [stdout] test cli::tell::tests::tell_empty_message_errors ... ok
[INFO] [stdout] test cli::tell::tests::tell_null_iter_when_no_state ... ok
[INFO] [stdout] test config::tests::accepts_stdin_prompt_without_placeholder ... ok
[INFO] [stdout] test config::tests::accepts_summarize_stdin_prompt_without_placeholder ... ok
[INFO] [stdout] test config::tests::default_template_enables_summarize ... ok
[INFO] [stdout] test config::tests::parses_5m_budget ... ok
[INFO] [stdout] test config::tests::parses_deadline_schedule ... ok
[INFO] [stdout] test config::tests::parses_float_variant ... ok
[INFO] [stdout] test config::tests::parses_full_default_template ... ok
[INFO] [stdout] test config::tests::parses_jq_variant ... ok
[INFO] [stdout] test config::tests::parses_regex_variant ... ok
[INFO] [stdout] test config::tests::parses_summarize_section ... ok
[INFO] [stdout] test cli::run::tests::refuses_dirty_repo ... ok
[INFO] [stdout] test config::tests::rejects_bad_direction ... ok
[INFO] [stdout] test config::tests::parses_total_budget_schedule ... ok
[INFO] [stdout] test config::tests::rejects_both_schedule_fields_set ... ok
[INFO] [stdout] test config::tests::rejects_bad_fail_mode ... ok
[INFO] [stdout] test config::tests::rejects_empty_name ... ok
[INFO] [stdout] test config::tests::rejects_name_with_slash ... ok
[INFO] [stdout] test config::tests::rejects_enabled_summarize_with_empty_command ... ok
[INFO] [stdout] test config::tests::rejects_neither_schedule_field_set ... ok
[INFO] [stdout] test config::tests::rejects_summarize_stdin_none_without_placeholder ... ok
[INFO] [stdout] test config::tests::summarize_enabled_by_default_when_section_absent ... ok
[INFO] [stdout] test config::tests::requires_prompt_file_placeholder_when_stdin_none ... ok
[INFO] [stdout] test iteration::tests::backfill_disabled_is_noop ... ok
[INFO] [stdout] test cli::run::tests::refuses_in_progress_without_resume ... ok
[INFO] [stdout] test cli::run::tests::allows_dirty_with_flag ... ok
[INFO] [stdout] test cli::tell::tests::tell_uses_run_completed_when_idle ... ok
[INFO] [stdout] test iteration::tests::backfill_skips_noop_missing_dir_and_existing ... ok
[INFO] [stdout] test cli::run::tests::refuses_unreachable_base_commit ... ok
[INFO] [stdout] test iteration::tests::backfill_uses_running_best_before_each_iter ... ok
[INFO] [stdout] test iteration::tests::backfill_fills_missing_summaries ... ok
[INFO] [stdout] test iteration::tests::backfill_force_overwrites_but_still_skips_ineligible ... ok
[INFO] [stdout] test cli::resume::tests::resume_records_killed_for_in_progress ... ok
[INFO] [stdout] test agent::tests::run_agent_kills_long_running ... ok
[INFO] [stdout] test cli::run::tests::lock_released_after_successful_run ... ok
[INFO] [stdout] test cli::run::tests::guidance_jsonl_picked_up_by_run_loop ... ok
[INFO] [stdout] test cli::run::tests::tolerates_dirty_autorize_dir ... ok
[INFO] [stdout] test cli::run::tests::fresh_recomputes_deadline_from_total_budget ... ok
[INFO] [stdout] test iteration::tests::operator_guidance_appears_in_prompt ... ok
[INFO] [stdout] test iteration::tests::runs_denied_outcome_when_diff_touches_deny_path ... ok
[INFO] [stdout] test lock::tests::acquire_creates_file_and_writes_pid ... ok
[INFO] [stdout] test lock::tests::lock_released_after_drop ... ok
[INFO] [stdout] test lock::tests::second_acquire_fails_while_held ... ok
[INFO] [stdout] test prompt::tests::prompt_no_history_message ... ok
[INFO] [stdout] test prompt::tests::prompt_omits_operator_guidance_when_empty ... ok
[INFO] [stdout] test prompt::tests::prompt_omits_summaries_section_when_all_empty ... ok
[INFO] [stdout] test prompt::tests::prompt_renders_boundaries_lists ... ok
[INFO] [stdout] test prompt::tests::prompt_renders_operator_guidance ... ok
[INFO] [stdout] test prompt::tests::prompt_renders_program_md_verbatim ... ok
[INFO] [stdout] test prompt::tests::prompt_renders_recent_summaries_section ... ok
[INFO] [stdout] test prompt::tests::prompt_snapshot ... ok
[INFO] [stdout] test prompt::tests::prompt_with_history_table ... ok
[INFO] [stdout] test prompt::tests::summary_prompt_includes_outcome_diff_and_stdio ... ok
[INFO] [stdout] test prompt::tests::summary_prompt_tails_long_stdout ... ok
[INFO] [stdout] test schedule::tests::compute_deadline_deadline_string ... ok
[INFO] [stdout] test schedule::tests::compute_deadline_total_budget ... ok
[INFO] [stdout] test schedule::tests::deadline_is_expired_false_before ... ok
[INFO] [stdout] test schedule::tests::deadline_is_expired_true_after ... ok
[INFO] [stdout] test schedule::tests::deadline_remaining_positive ... ok
[INFO] [stdout] test schedule::tests::deadline_remaining_saturates_zero ... ok
[INFO] [stdout] test schedule::tests::parse_duration_30m ... ok
[INFO] [stdout] test schedule::tests::parse_duration_form ... ok
[INFO] [stdout] test schedule::tests::parse_empty_errs ... ok
[INFO] [stdout] test schedule::tests::parse_garbage_errs ... ok
[INFO] [stdout] test schedule::tests::parse_natural_24h_form ... ok
[INFO] [stdout] test schedule::tests::parse_natural_bare_9am_future ... ok
[INFO] [stdout] test schedule::tests::parse_natural_bare_9am_past_rolls_to_tomorrow ... ok
[INFO] [stdout] test schedule::tests::parse_natural_noon_midnight ... ok
[INFO] [stdout] test schedule::tests::parse_natural_today_3pm ... ok
[INFO] [stdout] test schedule::tests::parse_natural_tomorrow_9am ... ok
[INFO] [stdout] test schedule::tests::parse_rfc3339_form ... ok
[INFO] [stdout] test scoring::tests::apply_fail_mode_abort_aborts ... ok
[INFO] [stdout] test scoring::tests::apply_fail_mode_invalid_discards ... ok
[INFO] [stdout] test scoring::tests::apply_fail_mode_passes_through_on_success ... ok
[INFO] [stdout] test scoring::tests::apply_fail_mode_worst_max_returns_f64_min ... ok
[INFO] [stdout] test scoring::tests::apply_fail_mode_worst_min_returns_f64_max ... ok
[INFO] [stdout] test scoring::tests::describe_failure_renders_each_variant ... ok
[INFO] [stdout] test scoring::tests::parse_float_ok ... ok
[INFO] [stdout] test scoring::tests::parse_float_rejects_inf ... ok
[INFO] [stdout] test scoring::tests::parse_float_rejects_nan ... ok
[INFO] [stdout] test scoring::tests::parse_float_rejects_non_numeric ... ok
[INFO] [stdout] test scoring::tests::parse_float_trims_whitespace ... ok
[INFO] [stdout] test scoring::tests::parse_jq_dollar_syntax_also_works ... ok
[INFO] [stdout] test scoring::tests::parse_jq_invalid_json_err ... ok
[INFO] [stdout] test scoring::tests::parse_jq_missing_field_err ... ok
[INFO] [stdout] test scoring::tests::parse_jq_not_a_number_err ... ok
[INFO] [stdout] test scoring::tests::parse_jq_ok ... ok
[INFO] [stdout] test scoring::tests::parse_regex_capture_not_number_err ... ok
[INFO] [stdout] test scoring::tests::parse_regex_no_capture_group_err ... ok
[INFO] [stdout] test scoring::tests::parse_regex_no_match_err ... ok
[INFO] [stdout] test cli::run::tests::fresh_preserves_best_and_discards_regression ... ok
[INFO] [stdout] test scoring::tests::parse_regex_ok ... ok
[INFO] [stdout] test cli::run::tests::keep_worktrees_does_not_block_second_iteration ... ok
[INFO] [stdout] test iteration::tests::discarded_kept_worktree_has_clean_index ... ok
[INFO] [stdout] test scoring::tests::score_reports_nonzero_exit_failure ... ok
[INFO] [stdout] test scoring::tests::score_captures_large_stdout ... ok
[INFO] [stdout] test scoring::tests::parse_regex_rejects_nonfinite_capture ... ok
[INFO] [stdout] test scoring::tests::score_runs_command_and_parses_float ... ok
[INFO] [stdout] test iteration::tests::merged_commit_contains_full_agent_diff ... ok
[INFO] [stdout] test storage::tests::read_guidance_accepts_hand_written_line ... ok
[INFO] [stdout] test storage::tests::pre_a2_record_without_summary_loads_with_empty_summary ... ok
[INFO] [stdout] test storage::tests::read_guidance_missing_returns_empty ... ok
[INFO] [stdout] test storage::tests::guidance_round_trips ... ok
[INFO] [stdout] test storage::tests::read_guidance_drops_torn_final_line ... ok
[INFO] [stdout] test iteration::tests::runs_noop_outcome_when_agent_makes_no_changes ... ok
[INFO] [stdout] test storage::tests::read_state_migrates_missing_run_counter ... ok
[INFO] [stdout] test storage::tests::read_state_missing_returns_none ... ok
[INFO] [stdout] test storage::tests::read_iterations_drops_torn_final_line_after_newlines ... ok
[INFO] [stdout] test storage::tests::read_iterations_errors_on_corrupt_middle_line ... ok
[INFO] [stdout] test storage::tests::rewrite_iterations_empty_truncates ... ok
[INFO] [stdout] test iteration::tests::runs_discarded_outcome_when_score_worse ... ok
[INFO] [stdout] test storage::tests::worst_sentinel_round_trips_through_json ... ok
[INFO] [stdout] test storage::tests::rewrite_iterations_round_trips ... ok
[INFO] [stdout] test cli::run::tests::respects_max_iterations ... ok
[INFO] [stdout] test storage::tests::rewrite_iterations_stray_tmp_doesnt_corrupt_dest ... ok
[INFO] [stdout] test storage::tests::state_round_trips_json ... ok
[INFO] [stdout] test storage::tests::write_atomic_stray_tmp_doesnt_corrupt_dest ... ok
[INFO] [stdout] test storage::tests::write_atomic_overwrites_destination ... ok
[INFO] [stdout] test storage::tests::read_iterations_drops_torn_final_line ... ok
[INFO] [stdout] test subproc::tests::stdin_payload_delivered ... ok
[INFO] [stdout] test subproc::tests::passes_extra_env ... ok
[INFO] [stdout] test subproc::tests::captures_large_stdout ... ok
[INFO] [stdout] test templates::tests::renders_experiment_name ... ok
[INFO] [stdout] test templates::tests::renders_program_experiment_name ... ok
[INFO] [stdout] test templates::tests::template_is_valid_config ... ok
[INFO] [stdout] test subproc::tests::runs_simple_echo_with_no_budget_hit ... ok
[INFO] [stdout] test worktree::tests::deny_path_matches_empty_returns_empty ... ok
[INFO] [stdout] test worktree::tests::deny_path_matches_globstar ... ok
[INFO] [stdout] test iteration::tests::runs_invalid_outcome_when_scoring_fails ... ok
[INFO] [stdout] test iteration::tests::summary_disabled_leaves_empty ... ok
[INFO] [stdout] test worktree::tests::deny_path_matches_basic ... ok
[INFO] [stdout] test iteration::tests::summary_skipped_for_noop_iteration ... ok
[INFO] [stdout] test iteration::tests::runs_merged_outcome_on_improvement ... ok
[INFO] [stdout] test scoring::tests::score_timeout_kills_and_reports ... ok
[INFO] [stdout] test worktree::tests::diff_paths_against_returns_changed_files ... ok
[INFO] [stdout] test worktree::tests::head_sha_matches_rev_parse ... ok
[INFO] [stdout] test worktree::tests::is_inside_repo_false_for_non_repo ... ok
[INFO] [stdout] test worktree::tests::is_clean_excluding_ignores_autorize_dir ... ok
[INFO] [stdout] test worktree::tests::diff_against_returns_diff_text ... ok
[INFO] [stdout] test worktree::tests::create_branch_and_branch_exists ... ok
[INFO] [stdout] test worktree::tests::is_clean_excluding_flags_unrelated_untracked ... ok
[INFO] [stdout] test storage::tests::append_iteration_100x_then_read_all ... ok
[INFO] [stdout] test worktree::tests::is_clean_true_after_commit ... ok
[INFO] [stdout] test worktree::tests::is_clean_false_after_modify ... ok
[INFO] [stdout] test iteration::tests::summary_generated_for_non_noop_iteration ... ok
[INFO] [stdout] test worktree::tests::resolve_ref_present_returns_sha ... ok
[INFO] [stdout] test worktree::tests::is_inside_repo_true_for_initted ... ok
[INFO] [stdout] test worktree::tests::resolve_ref_missing_returns_none ... ok
[INFO] [stdout] test cli::run::tests::fresh_reruns_after_completion ... ok
[INFO] [stdout] test iteration::tests::summary_failure_is_best_effort ... ok
[INFO] [stdout] test worktree::tests::commit_all_in_advances_tracking_branch ... ok
[INFO] [stdout] test subproc::tests::kills_grandchildren_via_pgroup ... ok
[INFO] [stdout] test subproc::tests::times_out_long_sleep ... ok
[INFO] [stdout] test worktree::tests::worktree_add_and_list_and_remove ... ok
[INFO] [stdout] test worktree::tests::two_worktrees_can_share_one_branch ... ok
[INFO] [stdout] test iteration::tests::summary_timeout_is_best_effort ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 216 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.60s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/e2e_pi.rs (/opt/rustwide/target/debug/deps/e2e_pi-bc9cb52c10db70ca)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test deny_path_violation_yields_denied_outcome ... ok
[INFO] [stdout] test resume_records_killed_then_continues ... ok
[INFO] [stderr] Preparing worktree (checking out 'autorize/pi')
[INFO] [stdout] HEAD is now at d6448b9 autorize iter 6: score 0.023803
[INFO] [stdout] test central_log_appends_and_tees_child_output ... ok
[INFO] [stdout] test secret_env_value_never_logged ... ok
[INFO] [stdout] test loop_converges_with_merges_and_discards ... ok
[INFO] [stderr]      Running tests/signal_cleanup.rs (/opt/rustwide/target/debug/deps/signal_cleanup-b365ba74fcb93f57)
[INFO] [stdout] test dirty_tree_refused_then_allow_dirty_succeeds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.97s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test sigint_tears_down_agent_process_group ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.31s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "82d08b335515735e9204e92885d49cc1ad14a5921cd53b4a1e1c1a673cc3ee95", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "82d08b335515735e9204e92885d49cc1ad14a5921cd53b4a1e1c1a673cc3ee95", kill_on_drop: false }`
[INFO] [stdout] 82d08b335515735e9204e92885d49cc1ad14a5921cd53b4a1e1c1a673cc3ee95
